[INFO] cloning repository https://github.com/efficient-novelty/atomic
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/efficient-novelty/atomic" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fefficient-novelty%2Fatomic", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fefficient-novelty%2Fatomic'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] d6c6f26741260d025c13e10e338dce00bf7de56d
[INFO] testing efficient-novelty/atomic/a41fcc202d3d226a6c21b0ebdd97129691b2a6d0 against master#1f7f8ea0721a3b1eb73e6c6d25cccb371434b320 for pr-154065
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fefficient-novelty%2Fatomic" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-1-tc1/source/.cargo/config.toml
[INFO] removed /workspace/builds/worker-1-tc1/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/efficient-novelty/atomic
[INFO] finished tweaking git repo https://github.com/efficient-novelty/atomic
[INFO] tweaked toml for git repo https://github.com/efficient-novelty/atomic written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/efficient-novelty/atomic on toolchain 1f7f8ea0721a3b1eb73e6c6d25cccb371434b320
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/efficient-novelty/atomic already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] c8ded596d1547fa28d1b26cf5f8c1b819c4cff56d2344416df5be0ab3875adec
[INFO] running `Command { std: "docker" "start" "-a" "c8ded596d1547fa28d1b26cf5f8c1b819c4cff56d2344416df5be0ab3875adec", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c8ded596d1547fa28d1b26cf5f8c1b819c4cff56d2344416df5be0ab3875adec", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c8ded596d1547fa28d1b26cf5f8c1b819c4cff56d2344416df5be0ab3875adec", kill_on_drop: false }`
[INFO] [stdout] c8ded596d1547fa28d1b26cf5f8c1b819c4cff56d2344416df5be0ab3875adec
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 49c9a7cfe430b30ef69915eaded01af36e3a40520237503cd1227f602f419415
[INFO] running `Command { std: "docker" "start" "-a" "49c9a7cfe430b30ef69915eaded01af36e3a40520237503cd1227f602f419415", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling ref-cast v1.0.25
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling cpufeatures v0.2.17
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling typenum v1.19.0
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling constant_time_eq v0.4.2
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling arrayref v0.3.9
[INFO] [stderr]    Compiling ref-cast-impl v1.0.25
[INFO] [stderr]    Compiling serde_derive_internals v0.29.1
[INFO] [stderr]    Compiling dyn-clone v1.0.20
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling foldhash v0.1.5
[INFO] [stderr]    Compiling anyhow v1.0.102
[INFO] [stderr]    Compiling fallible-iterator v0.3.0
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling fallible-streaming-iterator v0.1.9
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling toml_write v0.1.2
[INFO] [stderr]    Compiling powerfmt v0.2.0
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling time-core v0.1.8
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling num-conv v0.2.0
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling hashlink v0.10.0
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling clap_derive v4.6.0
[INFO] [stderr]    Compiling pen-cli v0.1.0 (/opt/rustwide/workdir/crates/pen-cli)
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling schemars_derive v1.2.1
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling blake3 v1.8.3
[INFO] [stderr]    Compiling libsqlite3-sys v0.35.0
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling sysinfo v0.35.2
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling pen-accel v0.1.0 (/opt/rustwide/workdir/crates/pen-accel)
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling schemars v1.2.1
[INFO] [stderr]    Compiling pen-core v0.1.0 (/opt/rustwide/workdir/crates/pen-core)
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]    Compiling pen-type v0.1.0 (/opt/rustwide/workdir/crates/pen-type)
[INFO] [stderr]    Compiling pen-eval v0.1.0 (/opt/rustwide/workdir/crates/pen-eval)
[INFO] [stderr]    Compiling rusqlite v0.37.0
[INFO] [stderr]    Compiling pen-store v0.1.0 (/opt/rustwide/workdir/crates/pen-store)
[INFO] [stderr]    Compiling pen-search v0.1.0 (/opt/rustwide/workdir/crates/pen-search)
[INFO] [stderr]    Compiling pen-agda v0.1.0 (/opt/rustwide/workdir/crates/pen-agda)
[INFO] [stderr]    Compiling xtask v0.1.0 (/opt/rustwide/workdir/xtask)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 47.91s
[INFO] running `Command { std: "docker" "inspect" "49c9a7cfe430b30ef69915eaded01af36e3a40520237503cd1227f602f419415", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "49c9a7cfe430b30ef69915eaded01af36e3a40520237503cd1227f602f419415", kill_on_drop: false }`
[INFO] [stdout] 49c9a7cfe430b30ef69915eaded01af36e3a40520237503cd1227f602f419415
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 592774ffebcc1af492d8ff3a783827a42ee01394ac5686874035c5731525e42d
[INFO] running `Command { std: "docker" "start" "-a" "592774ffebcc1af492d8ff3a783827a42ee01394ac5686874035c5731525e42d", kill_on_drop: false }`
[INFO] [stderr]    Compiling fs2 v0.4.3
[INFO] [stderr]    Compiling pen-accel v0.1.0 (/opt/rustwide/workdir/crates/pen-accel)
[INFO] [stderr]    Compiling pen-core v0.1.0 (/opt/rustwide/workdir/crates/pen-core)
[INFO] [stderr]    Compiling pen-type v0.1.0 (/opt/rustwide/workdir/crates/pen-type)
[INFO] [stderr]    Compiling pen-store v0.1.0 (/opt/rustwide/workdir/crates/pen-store)
[INFO] [stderr]    Compiling pen-eval v0.1.0 (/opt/rustwide/workdir/crates/pen-eval)
[INFO] [stderr]    Compiling pen-agda v0.1.0 (/opt/rustwide/workdir/crates/pen-agda)
[INFO] [stderr]    Compiling pen-search v0.1.0 (/opt/rustwide/workdir/crates/pen-search)
[INFO] [stderr]    Compiling xtask v0.1.0 (/opt/rustwide/workdir/xtask)
[INFO] [stderr]    Compiling pen-cli v0.1.0 (/opt/rustwide/workdir/crates/pen-cli)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 14.62s
[INFO] running `Command { std: "docker" "inspect" "592774ffebcc1af492d8ff3a783827a42ee01394ac5686874035c5731525e42d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "592774ffebcc1af492d8ff3a783827a42ee01394ac5686874035c5731525e42d", kill_on_drop: false }`
[INFO] [stdout] 592774ffebcc1af492d8ff3a783827a42ee01394ac5686874035c5731525e42d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 26607be49e962767748a214786aaf9294b3b5aac88384899850d33ee9a3ece30
[INFO] running `Command { std: "docker" "start" "-a" "26607be49e962767748a214786aaf9294b3b5aac88384899850d33ee9a3ece30", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.21s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pen_accel-aa0bd663b68844c4)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pen_agda-581cd0a6fa856a48)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test export::tests::export_writes_manifest_and_modules ... ok
[INFO] [stdout] test render::tests::renderer_uses_stable_step_module_names ... ok
[INFO] [stdout] test render::tests::renderer_emits_useful_comments_and_imports ... ok
[INFO] [stdout] test render::tests::payload_renderer_emits_contract_claims ... ok
[INFO] [stdout] test render::tests::expression_comments_are_human_readable ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/pen_cli-cd5f764eeb460bf0)
[INFO] [stdout] 
[INFO] [stdout] running 34 tests
[INFO] [stdout] test human::tests::expression_renderer_exposes_prior_step_labels ... ok
[INFO] [stdout] test cmd_export_agda::tests::export_command_writes_manifest ... ok
[INFO] [stdout] test cmd_run::tests::non_claim_auto_worker_resolution_stays_cpu_bound ... ok
[INFO] [stdout] test human::tests::step_labels_match_reference_names ... ok
[INFO] [stdout] test progress::tests::progress_helpers_render_goal_and_limit_bars ... ok
[INFO] [stdout] test progress::tests::progress_helpers_render_human_readable_units ... ok
[INFO] [stdout] test cmd_run::tests::signed_byte_delta_tracks_observed_gap_direction ... ok
[INFO] [stdout] test progress::tests::terminal_progress_streams_step_start_and_completion_lines ... ok
[INFO] [stdout] test cmd_run::tests::claim_auto_worker_resolution_uses_memory_headroom ... ok
[INFO] [stdout] test human::tests::candidate_summary_mentions_structure_kind_and_imports ... ok
[INFO] [stdout] test narrative::tests::claim_narrative_uses_claim_headline ... ok
[INFO] [stdout] test narrative::tests::demo_narrative_renders_budget_and_event_sections ... ok
[INFO] [stdout] test narrative::tests::demo_narrative_respects_the_configured_step_line_budget ... ok
[INFO] [stdout] test report::tests::prune_class_totals_include_exact_and_heuristic_counters ... ok
[INFO] [stdout] test report::tests::live_demo_generation_reports_step_progress ... ok
[INFO] [stdout] test report::tests::replay_reference_steps_matches_expected_sequence_length ... ok
[INFO] [stdout] test report::tests::report_renderers_include_latest_step_details ... ok
[INFO] [stdout] test narrative::tests::demo_narrative_surfaces_exact_screen_reason_codes ... ok
[INFO] [stdout] test narrative::tests::demo_narrative_writer_persists_text_and_ndjson ... ok
[INFO] [stdout] test report::tests::demo_debug_report_surfaces_bucket_summary ... ok
[INFO] [stdout] test report::tests::live_search_reports_candidate_breakdowns ... ok
[INFO] [stdout] test report::tests::resume_extension_continues_from_stored_step_reports ... ok
[INFO] [stdout] test report::tests::reevaluate_extension_rebuilds_prefix_reports_before_continuing ... ok
[INFO] [stdout] test cmd_run::tests::run_command_writes_frozen_manifest_and_reports ... ok
[INFO] [stdout] test cmd_run::tests::failed_partial_claim_run_still_leaves_manifest_and_narrative_artifacts ... ok
[INFO] [stdout] test cmd_run::tests::claim_run_writes_policy_metadata_and_claim_narrative ... ok
[INFO] [stdout] test cmd_run::tests::demo_run_writes_narrative_and_event_artifacts ... ok
[INFO] [stdout] test cmd_run::tests::failed_partial_late_run_still_leaves_frontier_snapshot ... ok
[INFO] [stdout] test cmd_run::tests::claim_run_persists_live_step_memory_checkpoints_before_acceptance ... ok
[INFO] [stdout] test cmd_resume::tests::resume_command_extends_reference_replay ... ok
[INFO] [stdout] test report::tests::bootstrap_search_matches_the_current_canon_for_fifteen_steps ... ok
[INFO] [stdout] test report::tests::late_bootstrap_reports_frontier_retention_annotations ... ok
[INFO] [stdout] test report::tests::pressure_hardened_bootstrap_surfaces_spill_pressure ... ok
[INFO] [stdout] test report::tests::step_generation_prefers_live_search_only_where_supported ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 54.93s
[INFO] [stdout] 
[INFO] [stderr]      Running ../../tests/integration/agda_export.rs (/opt/rustwide/target/debug/deps/agda_export-d875b92110004822)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test export_agda_prefers_run_artifacts_and_carries_step_fifteen_claims ... ok
[INFO] [stdout] test export_agda_emits_the_frozen_manifest_and_stub_source ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 11.43s
[INFO] [stdout] 
[INFO] [stderr]      Running ../../tests/integration/atomic_bootstrap.rs (/opt/rustwide/target/debug/deps/atomic_bootstrap-4f6778157ef22e9c)
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_six ... ok
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_eleven ... ok
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_nine ... ok
[INFO] [stdout] test bootstrap_run_surfaces_pressure_hardened_spill_backed_retention ... ok
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_fourteen ... ok
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_eight ... ok
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_ten ... ok
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_twelve ... ok
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_fifteen has been running for over 60 seconds
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_seven has been running for over 60 seconds
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_thirteen has been running for over 60 seconds
[INFO] [stdout] test bootstrap_run_writes_frozen_reference_artifacts has been running for over 60 seconds
[INFO] [stdout] test claim_benchmark_script_aggregates_runtime_floor_and_manifest_evidence has been running for over 60 seconds
[INFO] [stdout] test claim_certification_script_emits_failing_certificate_for_incomplete_smoke_run has been running for over 60 seconds
[INFO] [stdout] test compare_runs_reports_claim_lane_policy_and_reason_audit has been running for over 60 seconds
[INFO] [stdout] test compare_runs_reports_demo_phase_floor_and_closure_evidence has been running for over 60 seconds
[INFO] [stdout] test compare_runs_reports_missing_demo_narrative_artifacts_explicitly has been running for over 60 seconds
[INFO] [stdout] test bootstrap_run_writes_frozen_reference_artifacts ... ok
[INFO] [stdout] test compare_runs_reports_relaxed_shadow_admissibility_and_competition_deltas has been running for over 60 seconds
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_seven ... ok
[INFO] [stdout] test compare_runs_reports_workstream4_rollout_parity_and_pressure_sets has been running for over 60 seconds
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_thirteen ... ok
[INFO] [stdout] test demo_run_and_resume_append_narrative_output_when_requested has been running for over 60 seconds
[INFO] [stdout] test realistic_shadow_pressure_run_surfaces_prefix_frontier_pressure_and_drop has been running for over 60 seconds
[INFO] [stdout] test bootstrap_run_uses_live_search_through_step_fifteen ... ok
[INFO] [stdout] test realistic_shadow_run_preserves_reference_sequence_and_exposes_full_late_competition has been running for over 60 seconds
[INFO] [stdout] test relaxed_shadow_run_preserves_reference_sequence_and_exposes_late_competition has been running for over 60 seconds
[INFO] [stdout] test demo_run_and_resume_append_narrative_output_when_requested ... ok
[INFO] [stdout] test realistic_shadow_pressure_run_surfaces_prefix_frontier_pressure_and_drop ... ok
[INFO] [stdout] test relaxed_shadow_run_preserves_reference_sequence_and_exposes_late_competition ... ok
[INFO] [stdout] test claim_benchmark_script_aggregates_runtime_floor_and_manifest_evidence ... ok
[INFO] [stdout] test compare_runs_reports_claim_lane_policy_and_reason_audit ... ok
[INFO] [stdout] test claim_certification_script_emits_failing_certificate_for_incomplete_smoke_run ... ok
[INFO] [stdout] test compare_runs_reports_demo_phase_floor_and_closure_evidence ... ok
[INFO] [stdout] test compare_runs_reports_missing_demo_narrative_artifacts_explicitly ... ok
[INFO] [stdout] test compare_runs_reports_relaxed_shadow_admissibility_and_competition_deltas ... ok
[INFO] [stdout] test realistic_shadow_run_preserves_reference_sequence_and_exposes_full_late_competition ... ok
[INFO] [stderr]      Running ../../tests/integration/deterministic_replay.rs (/opt/rustwide/target/debug/deps/deterministic_replay-1161f311c91fa940)
[INFO] [stdout] test compare_runs_reports_workstream4_rollout_parity_and_pressure_sets ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 182.27s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test repeated_runs_with_the_same_inputs_are_deterministic ... ok
[INFO] [stdout] test compare_runs_script_emits_a_deterministic_evidence_signoff has been running for over 60 seconds
[INFO] [stdout] test compare_runs_script_emits_a_deterministic_evidence_signoff ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 91.37s
[INFO] [stdout] 
[INFO] [stderr]      Running ../../tests/integration/resume_roundtrip.rs (/opt/rustwide/target/debug/deps/resume_roundtrip-d94e64c781f43f26)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test realistic_resume_roundtrip_reevaluates_when_frontier_evaluator_changes ... ok
[INFO] [stdout] test realistic_resume_roundtrip_falls_back_to_step_checkpoint_when_frontier_search_changes ... ok
[INFO] [stdout] test resume_roundtrip_reevaluates_when_frontier_evaluator_changes ... ok
[INFO] [stdout] test resume_roundtrip_requires_migration_when_frontier_ast_changes ... ok
[INFO] [stdout] test resume_roundtrip_falls_back_to_step_checkpoint_when_frontier_search_changes ... ok
[INFO] [stdout] test realistic_resume_roundtrip_keeps_the_reference_sequence_and_prefix_frontier_evidence ... ok
[INFO] [stdout] test resume_roundtrip_keeps_the_reference_sequence_and_inspect_output ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 51.29s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pen_core-7ff31fc97b1b0b13)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test atom::tests::frozen_v1_atom_order_is_stable ... ok
[INFO] [stdout] test canonical::tests::canonicalization_is_structural_and_stable ... ok
[INFO] [stdout] test encode::tests::frozen_prefix_bits_match_the_skill_notes ... ok
[INFO] [stdout] test encode::tests::indexed_atoms_add_gamma_costs ... ok
[INFO] [stdout] test canonical::tests::telescope_keys_are_deterministic ... ok
[INFO] [stdout] test encode::tests::recursive_expr_bit_length_matches_donor_examples ... ok
[INFO] [stdout] test rational::tests::arithmetic_preserves_exactness ... ok
[INFO] [stdout] test library::tests::genuine_hilbert_marker_stays_closed_until_the_post_metric_shell ... ok
[INFO] [stdout] test library::tests::modal_and_temporal_capabilities_are_detected_structurally ... ok
[INFO] [stdout] test library::tests::genuine_temporal_marker_stays_closed_until_the_post_hilbert_shell ... ok
[INFO] [stdout] test rational::tests::rationals_are_normalized ... ok
[INFO] [stdout] test telescope::tests::cohesion_and_dct_classify_structurally ... ok
[INFO] [stdout] test rational::tests::ordering_uses_exact_cross_multiplication ... ok
[INFO] [stdout] test telescope::tests::hopf_references_the_expected_library_entries ... ok
[INFO] [stdout] test telescope::tests::reference_telescope_metrics_match_expected_shapes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pen_eval-7d799c1318fb4891)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test coherence::tests::fibonacci_window_matches_reference_values ... ok
[INFO] [stdout] test bar::tests::early_steps_use_frozen_half_bar ... ok
[INFO] [stdout] test bar::tests::fibonacci_bar_uses_exact_history_ratio ... ok
[INFO] [stdout] test bar::tests::rho_and_overshoot_stay_exact ... ok
[INFO] [stdout] test coherence::tests::active_window_tracks_last_visible_steps ... ok
[INFO] [stdout] test nu_trace::tests::trace_output_is_stable_for_reference_telescope ... ok
[INFO] [stdout] test minimality::tests::pi_reference_telescope_is_semantically_minimal_for_step_four ... ok
[INFO] [stdout] test scc::tests::reference_pi_graph_has_a_terminal_root_component ... ok
[INFO] [stdout] test minimality::tests::pi_reference_telescope_is_not_minimal_under_terminal_amputation ... ok
[INFO] [stdout] test nu::tests::reference_sequence_matches_donor_structural_totals ... ok
[INFO] [stdout] test nu::tests::dct_meta_theorem_bonuses_fire ... ok
[INFO] [stdout] test nu::tests::native_nu_trace_is_deterministic ... ok
[INFO] [stdout] test nu::tests::structural_nu_fast_path_matches_legacy_helper_composition ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pen_search-9ff10af860f22d36)
[INFO] [stdout] 
[INFO] [stdout] running 173 tests
[INFO] [stdout] test bounds::tests::absorb_bound_merges_precomputed_extrema ... ok
[INFO] [stdout] test bounds::tests::zero_clause_kappa_never_clears_the_bar ... ok
[INFO] [stdout] test bounds::tests::prefix_bound_tracks_exact_extrema_and_rho_bounds ... ok
[INFO] [stdout] test branch_bound::tests::accept_rank_prefers_lower_overshoot_then_lower_kappa ... ok
[INFO] [stdout] test branch_bound::tests::upper_bound_prune_is_exact ... ok
[INFO] [stdout] test engine::tests::certification_sort_prefers_best_accept_rank_before_tiebreaks ... ok
[INFO] [stdout] test dedupe::tests::dedupe_keeps_first_seen_representative ... ok
[INFO] [stdout] test diversify::tests::pressure_retention_keeps_rare_heads_resident_and_spills_later_cold_states ... ok
[INFO] [stdout] test diversify::tests::diversity_quota_keeps_stable_first_entries_per_bucket ... ok
[INFO] [stdout] test diversify::tests::obligation_policy_reserves_rare_heads_ahead_of_generic_macro_pressure ... ok
[INFO] [stdout] test accept::tests::acceptance_prefers_minimal_positive_overshoot ... ok
[INFO] [stdout] test engine::tests::claim_candidate_discovery_evaluation_only_runs_for_incumbent_improvers ... ok
[INFO] [stdout] test engine::tests::claim_lane_drops_cached_terminal_payloads_after_ranking ... ok
[INFO] [stdout] test engine::tests::claim_lane_releases_processed_prefix_groups_during_proof_close ... ok
[INFO] [stdout] test config::tests::debug_profile_keeps_search_single_threaded ... ok
[INFO] [stdout] test config::tests::missing_demo_section_defaults_cleanly ... ok
[INFO] [stdout] test config::tests::desktop_profile_matches_frozen_defaults ... ok
[INFO] [stdout] test config::tests::missing_search_profile_defaults_to_strict_canon_guarded ... ok
[INFO] [stdout] test engine::tests::claim_remaining_one_plateau_activation_marker_tracks_first_matching_surface ... ok
[INFO] [stdout] test config::tests::claim_profiles_parse_with_expected_budget_metadata ... ok
[INFO] [stdout] test config::tests::demo_profiles_parse_with_expected_budget_metadata ... ok
[INFO] [stdout] test engine::tests::demo_breadth_harvest_live_retune_can_borrow_again_when_floor_pressure_worsens ... ok
[INFO] [stdout] test engine::tests::demo_breadth_harvest_live_retune_can_return_time_to_proof_close_after_recovery ... ok
[INFO] [stdout] test engine::tests::demo_breadth_shadow_currently_reuses_realistic_shadow_admissibility ... ok
[INFO] [stdout] test engine::tests::demo_budget_controller_frontloads_more_spill_after_floor_miss_with_slack ... ok
[INFO] [stdout] test engine::tests::demo_budget_controller_protects_more_reserve_after_reserve_pressure ... ok
[INFO] [stdout] test config::tests::shadow_profiles_parse_with_expected_labels ... ok
[INFO] [stdout] test engine::tests::demo_budget_controller_respects_seeded_resume_timing ... ok
[INFO] [stdout] test engine::tests::demo_budget_controller_shares_early_window_and_late_spill ... ok
[INFO] [stdout] test engine::tests::bootstrap_search_can_continue_from_stored_prefix_telescopes ... ok
[INFO] [stdout] test engine::tests::demo_breadth_shadow_midstep_search_preserves_realistic_shadow_acceptance ... ok
[INFO] [stdout] test engine::tests::demo_live_budget_pulses_rate_limit_repeated_scout_rebalances ... ok
[INFO] [stdout] test engine::tests::demo_early_steps_restore_full_clause_catalog_generation ... ok
[INFO] [stdout] test engine::tests::demo_materialize_handoff_can_switch_on_from_closure_pressure_before_reserve_tightens ... ok
[INFO] [stdout] test engine::tests::demo_materialize_handoff_switches_on_when_reserve_is_tighter_than_pending_surface ... ok
[INFO] [stdout] test engine::tests::demo_proof_close_closure_mode_prefers_fast_prunes ... ok
[INFO] [stdout] test engine::tests::demo_proof_close_order_mode_switches_under_tight_reserve ... ok
[INFO] [stdout] test engine::tests::demo_proof_close_order_mode_switches_when_closure_pressure_dominates ... ok
[INFO] [stdout] test engine::tests::demo_proof_close_potential_mode_prefers_bridge_potential_when_survivability_ties ... ok
[INFO] [stdout] test engine::tests::demo_proof_close_potential_mode_prefers_incumbent_improvers ... ok
[INFO] [stdout] test engine::tests::demo_floor_hit_reason_is_persisted_into_proof_close ... ok
[INFO] [stdout] test engine::tests::demo_scout_rebalance_borrows_from_proof_close_reserve_under_floor_pressure ... ok
[INFO] [stdout] test engine::tests::demo_scout_rebalance_waits_until_the_scout_slice_finishes ... ok
[INFO] [stdout] test engine::tests::claim_remaining_one_algebraic_ceiling_keeps_reference_step_four_winner_prefix ... ok
[INFO] [stdout] test engine::tests::claim_remaining_one_algebraic_ceiling_prunes_before_summary_build ... ok
[INFO] [stdout] test engine::tests::demo_late_surface_carries_through_live_config_runs ... FAILED
[INFO] [stdout] test engine::tests::demo_mandatory_proof_close_and_seal_pulses_ignore_rate_limiting ... ok
[INFO] [stdout] test engine::tests::desktop_claim_shadow_has_a_distinct_admissibility_mode ... ok
[INFO] [stdout] test engine::tests::demo_reserve_protection_reason_is_persisted_into_proof_close ... ok
[INFO] [stdout] test engine::tests::desktop_claim_shadow_step_five_keeps_claim_generic_surface_under_demo_budget ... ok
[INFO] [stdout] test engine::tests::demo_steps_emit_search_side_phase_events ... ok
[INFO] [stdout] test engine::tests::desktop_claim_shadow_step_four_surface_diagnostics_do_not_activate_late_widening ... ok
[INFO] [stdout] test engine::tests::desktop_claim_shadow_switches_late_bucket_taxonomy_to_structural_generic ... ok
[INFO] [stdout] test engine::tests::demo_search_records_scout_rebalance_when_floor_pressure_stays_high ... ok
[INFO] [stdout] test engine::tests::live_search_support_is_honest_about_current_bootstrap_range ... ok
[INFO] [stdout] test engine::tests::online_work_items_cache_exact_filtered_next_clauses ... ok
[INFO] [stdout] test engine::tests::online_work_items_reuse_full_catalog_when_no_filter_applies ... ok
[INFO] [stdout] test engine::tests::prefix_queue_prefers_nearer_terminal_and_tighter_cached_continuations ... ok
[INFO] [stdout] test engine::tests::realistic_shadow_keeps_semantic_family_bucket_taxonomy ... ok
[INFO] [stdout] test engine::tests::realistic_shadow_step_eleven_prunes_impossible_family_hybrids_early ... ok
[INFO] [stdout] test engine::tests::realistic_shadow_step_fifteen_builds_a_nonempty_terminal_prefix_frontier ... ok
[INFO] [stdout] test engine::tests::realistic_shadow_step_thirteen_collapses_exact_remaining_two_surface ... ok
[INFO] [stdout] test engine::tests::demo_soft_cap_handoff_counts_proof_close_overrun ... ok
[INFO] [stdout] test engine::tests::relaxed_shadow_keeps_the_reference_telescope_admissible_through_step_fifteen ... ok
[INFO] [stdout] test engine::tests::relaxed_shadow_step_eleven_exposes_late_competition ... ok
[INFO] [stdout] test engine::tests::relaxed_shadow_step_ten_exposes_late_competition ... ok
[INFO] [stdout] test engine::tests::relaxed_shadow_step_twelve_exposes_late_competition ... ok
[INFO] [stdout] test engine::tests::relaxed_shadow_switches_modal_step_ten_to_preference_based_admissibility ... ok
[INFO] [stdout] test enumerate::tests::axiomatic_bundle_filters_accept_the_reference_library_bundle ... ok
[INFO] [stdout] test enumerate::tests::claim_generic_kappa_eight_catalog_adds_modal_temporal_exchange_variants ... ok
[INFO] [stdout] test enumerate::tests::claim_generic_kappa_four_catalog_adds_claim_only_bridge_variants ... ok
[INFO] [stdout] test enumerate::tests::claim_generic_kappa_nine_catalog_adds_higher_order_binder_variants ... ok
[INFO] [stdout] test enumerate::tests::claim_generic_kappa_seven_catalog_adds_operator_wrapper_variants ... ok
[INFO] [stdout] test enumerate::tests::claim_generic_kappa_six_catalog_adds_structural_shell_variants ... ok
[INFO] [stdout] test enumerate::tests::claim_generic_late_bands_drop_demo_only_variants ... ok
[INFO] [stdout] test enumerate::tests::claim_generic_late_bands_drop_realistic_only_variants ... ok
[INFO] [stdout] test engine::tests::demo_zero_reserve_records_proof_close_reserve_exhaustion ... ok
[INFO] [stdout] test enumerate::tests::claim_shadow_uses_claim_generic_late_surface ... ok
[INFO] [stdout] test enumerate::tests::connection_shell_filters_accept_the_reference_connection_bundle ... ok
[INFO] [stdout] test enumerate::tests::curvature_shell_filters_accept_the_reference_second_order_bundle ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_eight_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] test enumerate::tests::claim_generic_step_fourteen_raw_catalog_is_wider_than_realistic_shadow ... ok
[INFO] [stdout] test engine::tests::desktop_claim_shadow_late_steps_keep_reference_acceptance ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_five_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] test engine::tests::claim_summary_build_skips_full_rank_for_primary_dominated_incumbent ... ok
[INFO] [stdout] test engine::tests::claim_materialization_reopens_after_cached_pruning_summary ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_nine_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_seven_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] test engine::tests::claim_cached_rank_preprune_consumes_cached_summary_before_materialization ... ok
[INFO] [stdout] test engine::tests::bootstrap_search_discovers_the_first_fifteen_reference_telescopes ... ok
[INFO] [stdout] test engine::tests::cached_terminal_prefix_rank_summary_prunes_without_reopening_completion_summary ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_six_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] test enumerate::tests::enumeration_is_deterministic_and_window_limited ... ok
[INFO] [stdout] test enumerate::tests::higher_hit_filters_accept_the_reference_point_and_higher_path_package ... ok
[INFO] [stdout] test enumerate::tests::hilbert_functional_filters_accept_the_reference_step_fourteen_shell ... ok
[INFO] [stdout] test enumerate::tests::initial_hit_filters_accept_the_reference_point_and_path_package ... ok
[INFO] [stdout] test enumerate::tests::modal_shell_filters_accept_the_reference_modal_bundle ... ok
[INFO] [stdout] test enumerate::tests::operator_bundle_filters_accept_the_reference_metric_reading_shell ... ok
[INFO] [stdout] test enumerate::tests::realistic_shadow_step_fifteen_enumeration_exposes_more_than_one_telescope ... ok
[INFO] [stdout] test enumerate::tests::realistic_shadow_step_fourteen_enumeration_exposes_more_than_one_telescope ... ok
[INFO] [stdout] test enumerate::tests::realistic_shadow_step_thirteen_enumeration_exposes_more_than_one_telescope ... ok
[INFO] [stdout] test enumerate::tests::recursive_enumeration_reaches_nested_bootstrap_forms ... ok
[INFO] [stdout] test engine::tests::claim_compact_materialization_matches_summary_expansion_without_cache ... ok
[INFO] [stdout] test enumerate::tests::relaxed_shadow_step_ten_enumeration_exposes_more_than_one_telescope ... ok
[INFO] [stdout] test enumerate::tests::former_eliminator_filter_preserves_nested_step_four_clauses ... ok
[INFO] [stdout] test enumerate::tests::sphere_lift_filters_accept_the_reference_point_path_and_witness_package ... ok
[INFO] [stdout] test enumerate::tests::relaxed_shadow_step_eleven_enumeration_exposes_more_than_one_telescope ... ok
[INFO] [stdout] test engine::tests::claim_cached_terminal_queue_entry_bound_decision_uses_bound_summary_only ... ok
[INFO] [stdout] test enumerate::tests::step_eight_enumeration_contains_the_reference_sphere_package ... ok
[INFO] [stdout] test enumerate::tests::step_fifteen_enumeration_contains_the_reference_temporal_shell ... ok
[INFO] [stdout] test enumerate::tests::relaxed_shadow_step_twelve_enumeration_exposes_more_than_one_telescope ... ok
[INFO] [stdout] test enumerate::tests::step_four_positional_filters_accept_reference_eliminator_and_computation_clauses ... ok
[INFO] [stdout] test enumerate::tests::step_fourteen_enumeration_contains_the_reference_hilbert_functional_shell ... ok
[INFO] [stdout] test enumerate::tests::step_five_enumeration_contains_the_reference_hit_package ... ok
[INFO] [stdout] test enumerate::tests::step_nine_enumeration_contains_the_reference_axiomatic_bundle ... ok
[INFO] [stdout] test engine::tests::claim_materialization_tracks_direct_compact_path_without_cache ... ok
[INFO] [stdout] test enumerate::tests::step_seven_enumeration_contains_the_reference_higher_hit_package ... ok
[INFO] [stdout] test enumerate::tests::step_ten_enumeration_contains_the_reference_modal_shell ... ok
[INFO] [stdout] test enumerate::tests::step_thirteen_enumeration_contains_the_reference_operator_bundle ... ok
[INFO] [stdout] test enumerate::tests::step_six_enumeration_contains_the_reference_truncation_package ... ok
[INFO] [stdout] test engine::tests::claim_terminal_prefix_completion_summary_matches_direct_exact_assessment ... ok
[INFO] [stdout] test enumerate::tests::temporal_shell_filters_accept_the_reference_dct_bundle ... ok
[INFO] [stdout] test enumerate::tests::truncation_hit_filters_accept_the_reference_shell_and_path_package ... ok
[INFO] [stdout] test expand::tests::candidate_evaluation_is_structural_and_exact ... ok
[INFO] [stdout] test frontier::tests::frontier_window_compacts_and_exposes_priority_heads ... ok
[INFO] [stdout] test frontier::tests::priority_heads_follow_frontier_order_instead_of_raw_priority_sorting ... ok
[INFO] [stdout] test motif::tests::motifs_are_disabled_in_early_phases ... ok
[INFO] [stdout] test prefix_cache::tests::cache_can_insert_a_precomputed_group_without_recomputing_each_merge_step ... ok
[INFO] [stdout] test prefix_cache::tests::cache_merges_telescopes_that_share_an_exact_terminal_prefix ... ok
[INFO] [stdout] test prefix_cache::tests::prefix_signature_clone_shares_exact_payload_allocation ... ok
[INFO] [stdout] test prefix_cache::tests::prefix_signature_keeps_exactness_while_exposing_compact_fields ... ok
[INFO] [stdout] test prefix_memo::tests::active_window_clause_filter_skips_impossible_terminal_continuations ... ok
[INFO] [stdout] test prefix_memo::tests::admissibility_summary_tracks_trivial_derivability_incrementally ... ok
[INFO] [stdout] test prefix_memo::tests::cache_reuses_parent_summary_for_child_prefixes ... ok
[INFO] [stdout] test prefix_memo::tests::claim_shadow_defaults_to_claim_generic_family_surface ... ok
[INFO] [stdout] test prefix_memo::tests::claim_terminal_admissibility_matches_direct_assessment_without_family_summary ... ok
[INFO] [stdout] test prefix_memo::tests::claim_terminal_clause_filter_matches_direct_admissibility_without_family_summary ... ok
[INFO] [stdout] test prefix_memo::tests::claim_terminal_connectivity_keeps_reference_step_four_winner_clause ... ok
[INFO] [stdout] test enumerate::tests::telescope_enumeration_discovers_bootstrap_candidates ... ok
[INFO] [stdout] test prefix_memo::tests::demo_surface_override_keeps_demo_only_terminal_clause_in_active_window_filter ... ok
[INFO] [stdout] test prefix_memo::tests::family_option_count_exposes_remaining_exact_family_surface ... ok
[INFO] [stdout] test prefix_memo::tests::partial_prefix_bound_decision_reuses_cached_exact_result ... ok
[INFO] [stdout] test prefix_memo::tests::take_terminal_prefix_completion_summary_removes_cached_payload_after_reuse ... ok
[INFO] [stdout] test prefix_memo::tests::temporal_shell_terminal_summary_shortcuts_historical_reanchor ... ok
[INFO] [stdout] test prefix_memo::tests::terminal_admissibility_matches_direct_assessment_when_family_summary_is_available ... ok
[INFO] [stdout] test prefix_memo::tests::terminal_clause_filter_skips_inadmissible_last_clauses_before_connectivity ... ok
[INFO] [stdout] test prefix_memo::tests::terminal_prefix_bound_summary_reads_cached_bound_without_cloning_payload ... ok
[INFO] [stdout] test prefix_memo::tests::terminal_prefix_completion_summary_reuses_cached_exact_results ... ok
[INFO] [stdout] test priority::tests::priority_key_is_deterministic_and_band_first ... ok
[INFO] [stdout] test resume::tests::checkpoint_resume_policy_uses_the_same_ast_type_eval_ladder ... ok
[INFO] [stdout] test resume::tests::resume_policy_drops_frontier_when_search_changes ... ok
[INFO] [stdout] test resume::tests::resume_policy_prefers_frontier_only_on_full_match ... ok
[INFO] [stdout] test resume::tests::resume_policy_requires_reevaluation_when_evaluator_changes ... ok
[INFO] [stdout] test resume::tests::resume_policy_stops_on_ast_changes ... ok
[INFO] [stdout] test scheduler::tests::scheduler_is_deterministic_and_respects_worker_hints ... ok
[INFO] [stdout] test scheduler::tests::scheduler_spreads_rare_heads_before_following_worker_hints ... ok
[INFO] [stdout] test state::tests::frontier_record_round_trips_exact_64_byte_layout ... ok
[INFO] [stdout] test worker::tests::worker_batch_prunes_states_that_cannot_clear_the_bar ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_ten_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] test enumerate::tests::step_four_enumeration_contains_the_reference_former_package ... ok
[INFO] [stdout] test engine::tests::claim_exact_partial_prefix_bound_decision_reuses_cached_terminal_result ... ok
[INFO] [stdout] test enumerate::tests::step_one_raw_enumeration_counts_the_full_clause_catalog_surface ... ok
[INFO] [stdout] test prefix_memo::tests::claim_terminal_connectivity_matches_direct_step_four_assessment ... ok
[INFO] [stdout] test engine::tests::exact_partial_prefix_bound_decision_reuses_cached_multistep_result ... ok
[INFO] [stdout] test engine::tests::exact_partial_prefix_bound_decision_reuses_cached_terminal_result ... ok
[INFO] [stdout] test engine::tests::reference_dct_becomes_admissible_and_connected_after_the_live_hilbert_prefix ... ok
[INFO] [stdout] test enumerate::tests::step_eleven_enumeration_contains_the_reference_connection_shell ... ok
[INFO] [stdout] test enumerate::tests::step_twelve_enumeration_contains_the_reference_curvature_shell ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_eleven_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_thirteen_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_fifteen_enumeration_exposes_more_surface_than_realistic_shadow has been running for over 60 seconds
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_fourteen_enumeration_exposes_more_surface_than_realistic_shadow has been running for over 60 seconds
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_twelve_enumeration_exposes_more_surface_than_realistic_shadow has been running for over 60 seconds
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_fourteen_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_fifteen_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] test enumerate::tests::demo_breadth_shadow_step_twelve_enumeration_exposes_more_surface_than_realistic_shadow ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- engine::tests::demo_late_surface_carries_through_live_config_runs stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'engine::tests::demo_late_surface_carries_through_live_config_runs' (404) panicked at crates/pen-search/src/engine.rs:8043:9:
[INFO] [stdout] step 10 should keep the generated floor hit
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62b441a85bca - std[f92b4c606f291077]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x62b441a85bca - std[f92b4c606f291077]::backtrace_rs::backtrace::trace_unsynchronized::<std[f92b4c606f291077]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x62b441a85bca - std[f92b4c606f291077]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x62b441a85bca - <<std[f92b4c606f291077]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[786f478f706679df]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x62b441a9b56a - <core[786f478f706679df]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x62b441a9b56a - core[786f478f706679df]::fmt::write
[INFO] [stdout]    6:     0x62b441a8a922 - std[f92b4c606f291077]::io::default_write_fmt::<alloc[64763625b5a07cdb]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x62b441a8a922 - <alloc[64763625b5a07cdb]::vec::Vec<u8> as std[f92b4c606f291077]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x62b441a6447f - <std[f92b4c606f291077]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x62b441a6447f - std[f92b4c606f291077]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x62b441a7de09 - std[f92b4c606f291077]::panicking::default_hook
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x62b44195944c - <alloc[64763625b5a07cdb]::boxed::Box<dyn for<'a, 'b> core[786f478f706679df]::ops::function::Fn<(&'a std[f92b4c606f291077]::panic::PanicHookInfo<'b>,), Output = ()> + core[786f478f706679df]::marker::Sync + core[786f478f706679df]::marker::Send> as core[786f478f706679df]::ops::function::Fn<(&std[f92b4c606f291077]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x62b44195944c - test[20e1f0ae6cad7a17]::test_main_with_exit_callback::<test[20e1f0ae6cad7a17]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x62b441a7dfc2 - <alloc[64763625b5a07cdb]::boxed::Box<dyn for<'a, 'b> core[786f478f706679df]::ops::function::Fn<(&'a std[f92b4c606f291077]::panic::PanicHookInfo<'b>,), Output = ()> + core[786f478f706679df]::marker::Sync + core[786f478f706679df]::marker::Send> as core[786f478f706679df]::ops::function::Fn<(&std[f92b4c606f291077]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x62b441a7dfc2 - std[f92b4c606f291077]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x62b441a6456a - std[f92b4c606f291077]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x62b441a5b829 - std[f92b4c606f291077]::sys::backtrace::__rust_end_short_backtrace::<std[f92b4c606f291077]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x62b441a6533d - __rustc[8241495c18a89d70]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x62b441a9bcfc - core[786f478f706679df]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x62b4416bba45 - pen_search[7908b71de3b5c6c9]::engine::tests::demo_late_surface_carries_through_live_config_runs
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/pen-search/src/engine.rs:8043:9
[INFO] [stdout]   20:     0x62b4416af723 - pen_search[7908b71de3b5c6c9]::engine::tests::demo_late_surface_carries_through_live_config_runs::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/pen-search/src/engine.rs:7995:60
[INFO] [stdout]   21:     0x62b4416a76e6 - <pen_search[7908b71de3b5c6c9]::engine::tests::demo_late_surface_carries_through_live_config_runs::{closure#0} as core[786f478f706679df]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x62b44194d52b - <fn() -> core[786f478f706679df]::result::Result<(), alloc[64763625b5a07cdb]::string::String> as core[786f478f706679df]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x62b44194d52b - test[20e1f0ae6cad7a17]::__rust_begin_short_backtrace::<core[786f478f706679df]::result::Result<(), alloc[64763625b5a07cdb]::string::String>, fn() -> core[786f478f706679df]::result::Result<(), alloc[64763625b5a07cdb]::string::String>>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/test/src/lib.rs:663:18
[INFO] [stdout]   24:     0x62b441959f1b - test[20e1f0ae6cad7a17]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/test/src/lib.rs:686:74
[INFO] [stdout]   25:     0x62b441959f1b - <core[786f478f706679df]::panic::unwind_safe::AssertUnwindSafe<test[20e1f0ae6cad7a17]::run_test_in_process::{closure#0}> as core[786f478f706679df]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x62b441959f1b - std[f92b4c606f291077]::panicking::catch_unwind::do_call::<core[786f478f706679df]::panic::unwind_safe::AssertUnwindSafe<test[20e1f0ae6cad7a17]::run_test_in_process::{closure#0}>, core[786f478f706679df]::result::Result<(), alloc[64763625b5a07cdb]::string::String>>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x62b441959f1b - std[f92b4c606f291077]::panicking::catch_unwind::<core[786f478f706679df]::result::Result<(), alloc[64763625b5a07cdb]::string::String>, core[786f478f706679df]::panic::unwind_safe::AssertUnwindSafe<test[20e1f0ae6cad7a17]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x62b441959f1b - std[f92b4c606f291077]::panic::catch_unwind::<core[786f478f706679df]::panic::unwind_safe::AssertUnwindSafe<test[20e1f0ae6cad7a17]::run_test_in_process::{closure#0}>, core[786f478f706679df]::result::Result<(), alloc[64763625b5a07cdb]::string::String>>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x62b441959f1b - test[20e1f0ae6cad7a17]::run_test_in_process
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/test/src/lib.rs:686:27
[INFO] [stdout]   30:     0x62b441959f1b - test[20e1f0ae6cad7a17]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/test/src/lib.rs:607:43
[INFO] [stdout]   31:     0x62b441955634 - test[20e1f0ae6cad7a17]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/test/src/lib.rs:637:41
[INFO] [stdout]   32:     0x62b441955634 - std[f92b4c606f291077]::sys::backtrace::__rust_begin_short_backtrace::<test[20e1f0ae6cad7a17]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x62b44195cb22 - std[f92b4c606f291077]::thread::lifecycle::spawn_unchecked::<test[20e1f0ae6cad7a17]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   34:     0x62b44195cb22 - <core[786f478f706679df]::panic::unwind_safe::AssertUnwindSafe<std[f92b4c606f291077]::thread::lifecycle::spawn_unchecked<test[20e1f0ae6cad7a17]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[786f478f706679df]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x62b44195cb22 - std[f92b4c606f291077]::panicking::catch_unwind::do_call::<core[786f478f706679df]::panic::unwind_safe::AssertUnwindSafe<std[f92b4c606f291077]::thread::lifecycle::spawn_unchecked<test[20e1f0ae6cad7a17]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x62b44195cb22 - std[f92b4c606f291077]::panicking::catch_unwind::<(), core[786f478f706679df]::panic::unwind_safe::AssertUnwindSafe<std[f92b4c606f291077]::thread::lifecycle::spawn_unchecked<test[20e1f0ae6cad7a17]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x62b44195cb22 - std[f92b4c606f291077]::panic::catch_unwind::<core[786f478f706679df]::panic::unwind_safe::AssertUnwindSafe<std[f92b4c606f291077]::thread::lifecycle::spawn_unchecked<test[20e1f0ae6cad7a17]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x62b44195cb22 - std[f92b4c606f291077]::thread::lifecycle::spawn_unchecked::<test[20e1f0ae6cad7a17]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   39:     0x62b44195cb22 - <std[f92b4c606f291077]::thread::lifecycle::spawn_unchecked<test[20e1f0ae6cad7a17]::run_test::{closure#1}, ()>::{closure#1} as core[786f478f706679df]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x62b441a8517f - <alloc[64763625b5a07cdb]::boxed::Box<dyn core[786f478f706679df]::ops::function::FnOnce<(), Output = ()> + core[786f478f706679df]::marker::Send> as core[786f478f706679df]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   41:     0x62b441a8517f - <std[f92b4c606f291077]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   42:     0x71b26ebeeaa4 - <unknown>
[INFO] [stdout]   43:     0x71b26ec7ba64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     engine::tests::demo_late_surface_carries_through_live_config_runs
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 172 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 133.91s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p pen-search --lib`
[INFO] running `Command { std: "docker" "inspect" "26607be49e962767748a214786aaf9294b3b5aac88384899850d33ee9a3ece30", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "26607be49e962767748a214786aaf9294b3b5aac88384899850d33ee9a3ece30", kill_on_drop: false }`
[INFO] [stdout] 26607be49e962767748a214786aaf9294b3b5aac88384899850d33ee9a3ece30
